[INFO] cloning repository https://github.com/tontinton/cachey
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/tontinton/cachey" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Ftontinton%2Fcachey", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Ftontinton%2Fcachey'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 5dda18016e8d975e4c2d6e60c0ad803a96df2cde
[INFO] testing tontinton/cachey against try#28f95933dc0727396c07b48d0a8cb5211b7fb472 for pr-154065-2
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Ftontinton%2Fcachey" "/workspace/builds/worker-0-tc2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-0-tc2/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/tontinton/cachey
[INFO] finished tweaking git repo https://github.com/tontinton/cachey
[INFO] tweaked toml for git repo https://github.com/tontinton/cachey written to /workspace/builds/worker-0-tc2/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/tontinton/cachey on toolchain 28f95933dc0727396c07b48d0a8cb5211b7fb472
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+28f95933dc0727396c07b48d0a8cb5211b7fb472" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/tontinton/cachey already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+28f95933dc0727396c07b48d0a8cb5211b7fb472" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+28f95933dc0727396c07b48d0a8cb5211b7fb472" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] e0d0e3620f7ed9d32f1ecb2cc6122b13cff6166667e0649a39d10f330935863b
[INFO] running `Command { std: "docker" "start" "-a" "e0d0e3620f7ed9d32f1ecb2cc6122b13cff6166667e0649a39d10f330935863b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "e0d0e3620f7ed9d32f1ecb2cc6122b13cff6166667e0649a39d10f330935863b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "e0d0e3620f7ed9d32f1ecb2cc6122b13cff6166667e0649a39d10f330935863b", kill_on_drop: false }`
[INFO] [stdout] e0d0e3620f7ed9d32f1ecb2cc6122b13cff6166667e0649a39d10f330935863b
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+28f95933dc0727396c07b48d0a8cb5211b7fb472" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] f5d17b3f3994d4d9fc1febfb2bc091a2ad87cd7ceae1eb4fd518f010af097923
[INFO] running `Command { std: "docker" "start" "-a" "f5d17b3f3994d4d9fc1febfb2bc091a2ad87cd7ceae1eb4fd518f010af097923", kill_on_drop: false }`
[INFO] [stderr]    Compiling proc-macro2 v1.0.104
[INFO] [stderr]    Compiling quote v1.0.42
[INFO] [stderr]    Compiling libc v0.2.178
[INFO] [stderr]    Compiling io-uring v0.7.11
[INFO] [stderr]    Compiling crossbeam-utils v0.8.21
[INFO] [stderr]    Compiling tracing-core v0.1.36
[INFO] [stderr]    Compiling arrayvec v0.7.6
[INFO] [stderr]    Compiling compio-driver v0.7.1
[INFO] [stderr]    Compiling winnow v0.7.14
[INFO] [stderr]    Compiling indexmap v2.12.1
[INFO] [stderr]    Compiling zerocopy v0.8.31
[INFO] [stderr]    Compiling zmij v1.0.7
[INFO] [stderr]    Compiling tikv-jemalloc-sys v0.6.1+5.3.0-1-ge13ca993e8ccb9ba9847cc330696e02839f328f7
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling compio-fs v0.7.0
[INFO] [stderr]    Compiling serde_json v1.0.148
[INFO] [stderr]    Compiling protobuf v3.7.2
[INFO] [stderr]    Compiling anstream v0.6.21
[INFO] [stderr]    Compiling regex-automata v0.4.13
[INFO] [stderr]    Compiling ahash v0.8.12
[INFO] [stderr]    Compiling nix v0.30.1
[INFO] [stderr]    Compiling either v1.15.0
[INFO] [stderr]    Compiling prometheus v0.14.0
[INFO] [stderr]    Compiling eyre v0.6.12
[INFO] [stderr]    Compiling clap_builder v4.5.53
[INFO] [stderr]    Compiling hashbrown v0.14.5
[INFO] [stderr]    Compiling hashbrown v0.16.1
[INFO] [stderr]    Compiling futures-sink v0.3.31
[INFO] [stderr]    Compiling crossbeam-channel v0.5.15
[INFO] [stderr]    Compiling crossbeam-queue v0.3.12
[INFO] [stderr]    Compiling concurrent-queue v2.5.0
[INFO] [stderr]    Compiling indenter v0.3.4
[INFO] [stderr]    Compiling tokio v1.49.0
[INFO] [stderr]    Compiling event-listener v5.4.1
[INFO] [stderr]    Compiling bytesize v2.3.1
[INFO] [stderr]    Compiling tracing-log v0.2.0
[INFO] [stderr]    Compiling event-listener-strategy v0.5.4
[INFO] [stderr]    Compiling tracing-serde v0.2.0
[INFO] [stderr]    Compiling humantime v2.3.0
[INFO] [stderr]    Compiling async-broadcast v0.7.2
[INFO] [stderr]    Compiling syn v2.0.112
[INFO] [stderr]    Compiling compio-buf v0.5.1
[INFO] [stderr]    Compiling socket2 v0.5.10
[INFO] [stderr]    Compiling parking_lot_core v0.9.12
[INFO] [stderr]    Compiling getrandom v0.2.16
[INFO] [stderr]    Compiling os_pipe v1.2.3
[INFO] [stderr]    Compiling getrandom v0.3.4
[INFO] [stderr]    Compiling num_cpus v1.17.0
[INFO] [stderr]    Compiling parking_lot v0.12.5
[INFO] [stderr]    Compiling nanorand v0.7.0
[INFO] [stderr]    Compiling dashmap v6.1.0
[INFO] [stderr]    Compiling flume v0.11.1
[INFO] [stderr]    Compiling matchers v0.2.0
[INFO] [stderr]    Compiling toml_parser v1.0.6+spec-1.1.0
[INFO] [stderr]    Compiling quick_cache v0.6.18
[INFO] [stderr]    Compiling toml_edit v0.23.10+spec-1.0.0
[INFO] [stderr]    Compiling proc-macro-crate v3.4.0
[INFO] [stderr]    Compiling tracing-attributes v0.1.31
[INFO] [stderr]    Compiling futures-macro v0.3.31
[INFO] [stderr]    Compiling thiserror-impl v1.0.69
[INFO] [stderr]    Compiling thiserror-impl v2.0.17
[INFO] [stderr]    Compiling pin-project-internal v1.1.10
[INFO] [stderr]    Compiling strum_macros v0.27.2
[INFO] [stderr]    Compiling compio-macros v0.1.2
[INFO] [stderr]    Compiling async-trait v0.1.89
[INFO] [stderr]    Compiling rsmp-derive v0.1.0 (/opt/rustwide/workdir/rsmp-derive)
[INFO] [stderr]    Compiling clap_derive v4.5.49
[INFO] [stderr]    Compiling futures-util v0.3.31
[INFO] [stderr]    Compiling thiserror v1.0.69
[INFO] [stderr]    Compiling thiserror v2.0.17
[INFO] [stderr]    Compiling protobuf-support v3.7.2
[INFO] [stderr]    Compiling pin-project v1.1.10
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling clap v4.5.53
[INFO] [stderr]    Compiling compio-log v0.1.0
[INFO] [stderr]    Compiling tracing-subscriber v0.3.22
[INFO] [stderr]    Compiling strum v0.27.2
[INFO] [stderr]    Compiling compio-io v0.6.0
[INFO] [stderr]    Compiling rsmp v0.1.0 (/opt/rustwide/workdir/rsmp)
[INFO] [stderr]    Compiling compio-runtime v0.7.0
[INFO] [stderr]    Compiling compio-signal v0.5.0
[INFO] [stderr]    Compiling compio-net v0.7.0
[INFO] [stderr]    Compiling compio v0.14.0
[INFO] [stderr]    Compiling tikv-jemallocator v0.6.1
[INFO] [stderr]    Compiling cachey v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 1m 42s
[INFO] running `Command { std: "docker" "inspect" "f5d17b3f3994d4d9fc1febfb2bc091a2ad87cd7ceae1eb4fd518f010af097923", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "f5d17b3f3994d4d9fc1febfb2bc091a2ad87cd7ceae1eb4fd518f010af097923", kill_on_drop: false }`
[INFO] [stdout] f5d17b3f3994d4d9fc1febfb2bc091a2ad87cd7ceae1eb4fd518f010af097923
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+28f95933dc0727396c07b48d0a8cb5211b7fb472" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 674c21e2ba3f8523532cf4ccdee990328a723d1a5eeedaf4b4bf806babd802cc
[INFO] running `Command { std: "docker" "start" "-a" "674c21e2ba3f8523532cf4ccdee990328a723d1a5eeedaf4b4bf806babd802cc", kill_on_drop: false }`
[INFO] [stderr]    Compiling io-uring v0.7.11
[INFO] [stderr]    Compiling tokio-macros v2.6.0
[INFO] [stderr]    Compiling socket2 v0.6.1
[INFO] [stderr]    Compiling mio v1.1.1
[INFO] [stderr]    Compiling bytes v1.11.0
[INFO] [stderr]    Compiling rustix v1.1.3
[INFO] [stderr]    Compiling flume v0.11.1
[INFO] [stderr]    Compiling nix v0.30.1
[INFO] [stderr]    Compiling tokio v1.49.0
[INFO] [stderr]    Compiling compio-driver v0.7.1
[INFO] [stderr]    Compiling compio-runtime v0.7.0
[INFO] [stderr]    Compiling compio-net v0.7.0
[INFO] [stderr]    Compiling compio-fs v0.7.0
[INFO] [stderr]    Compiling compio-signal v0.5.0
[INFO] [stderr]    Compiling compio v0.14.0
[INFO] [stderr]    Compiling tempfile v3.24.0
[INFO] [stderr]    Compiling cachey v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]    Compiling tokio-util v0.7.17
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 20.25s
[INFO] running `Command { std: "docker" "inspect" "674c21e2ba3f8523532cf4ccdee990328a723d1a5eeedaf4b4bf806babd802cc", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "674c21e2ba3f8523532cf4ccdee990328a723d1a5eeedaf4b4bf806babd802cc", kill_on_drop: false }`
[INFO] [stdout] 674c21e2ba3f8523532cf4ccdee990328a723d1a5eeedaf4b4bf806babd802cc
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+28f95933dc0727396c07b48d0a8cb5211b7fb472" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 070aab621a60eff846fbb7e0c0c6d94dba9a487c3d639b4c71bf609737460295
[INFO] running `Command { std: "docker" "start" "-a" "070aab621a60eff846fbb7e0c0c6d94dba9a487c3d639b4c71bf609737460295", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.29s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/cachey-81bca8964c0cc93b)
[INFO] [stdout] 
[INFO] [stdout] running 19 tests
[INFO] [stdout] test cache::tests::memory_cache::exact_match_required ... ok
[INFO] [stdout] test memory_semaphore::tests::bytes_to_permits_rounds_up ... ok
[INFO] [stdout] test cache::tests::memory_cache::tracks_evictions_count_and_bytes ... ok
[INFO] [stdout] test cache::tests::memory_cache::weight_reflects_data_size ... ok
[INFO] [stdout] test cache::tests::disk_cache::cleanup_pending_reflects_queue_length ... ok
[INFO] [stdout] test cache::tests::disk_cache::tracks_evictions_count_and_bytes ... ok
[INFO] [stdout] test memory_semaphore::tests::acquire_release_restores_permits ... ok
[INFO] [stdout] test cache::tests::disk_cache::tracks_hits_and_misses ... ok
[INFO] [stdout] test cache::tests::memory_cache::eviction_by_weight ... ok
[INFO] [stdout] test server::tests::capture_ranges_no_overlap_produces_empty ... ok
[INFO] [stdout] test server::tests::capture_ranges_single_chunk ... ok
[INFO] [stdout] test server::tests::read_exact_with_reused_buffer_respects_remaining ... ok
[INFO] [stdout] test cache::tests::disk_cache::eviction_sends_to_cleanup_channel ... ok
[INFO] [stdout] test server::tests::stream_file_truncates_at_eof ... ok
[INFO] [stdout] test memory_semaphore::tests::concurrent_acquire_release_maintains_invariants ... ok
[INFO] [stdout] test server::tests::stream_file_returns_exact_requested_range ... ok
[INFO] [stdout] test server::tests::capture_ranges_across_chunks ... ok
[INFO] [stdout] test cache::tests::memory_cache::same_file_different_ranges_coexist ... ok
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/cachey-7152b8275269969b)
[INFO] [stdout] test memory_semaphore::tests::acquire_blocks_when_insufficient_permits ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 19 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.11s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stderr]      Running tests/put_and_get.rs (/opt/rustwide/target/debug/deps/put_and_get-ff2d00fb84abc011)
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 13 tests
[INFO] [stdout] test put_and_get_compio ... FAILED
[INFO] [stdout] test connection_reuse_after_put has been running for over 60 seconds
[INFO] [stdout] test get_falls_back_to_disk_on_memory_miss has been running for over 60 seconds
[INFO] [stdout] test get_hits_memory_cache_before_disk has been running for over 60 seconds
[INFO] [stdout] test get_partial_file_from_disk has been running for over 60 seconds
[INFO] [stdout] test get_range_past_eof_returns_partial_data has been running for over 60 seconds
[INFO] [stdout] test health_endpoint_returns_ok has been running for over 60 seconds
[INFO] [stdout] test put_and_get_tokio has been running for over 60 seconds
[INFO] [stdout] test put_and_get_verifies_disk_cache_metrics has been running for over 60 seconds
[INFO] [stdout] test put_existing_file_large_body_returns_already_exists has been running for over 60 seconds
[INFO] [stdout] test put_existing_file_skips_write has been running for over 60 seconds
[INFO] [stdout] test put_with_cache_ranges_populates_memory_cache has been running for over 60 seconds
[INFO] [stdout] test sequential_requests_complete_under_memory_pressure has been running for over 60 seconds
[ERROR] error running command: no output for 300 seconds
[INFO] running `Command { std: "docker" "inspect" "070aab621a60eff846fbb7e0c0c6d94dba9a487c3d639b4c71bf609737460295", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "070aab621a60eff846fbb7e0c0c6d94dba9a487c3d639b4c71bf609737460295", kill_on_drop: false }`
[INFO] [stdout] 070aab621a60eff846fbb7e0c0c6d94dba9a487c3d639b4c71bf609737460295
